Dec 03 14:56:02.787 INFO testing crisp-status-reporter-1.1.2 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 03 14:56:02.787 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:03.056 INFO [stdout] 069a0422981e1cfd596c4263d8dfae48f575e3199b44c9a77e367c17a477be08
Dec 03 14:56:03.058 INFO running `"docker" "start" "-a" "069a0422981e1cfd596c4263d8dfae48f575e3199b44c9a77e367c17a477be08"`
Dec 03 14:56:03.579 INFO [stderr] usermod: no changes
Dec 03 14:56:03.915 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 03 14:56:13.563 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.95s
Dec 03 14:56:13.602 INFO [stderr] su: No module specific data is present
Dec 03 14:56:14.451 INFO running `"docker" "inspect" "069a0422981e1cfd596c4263d8dfae48f575e3199b44c9a77e367c17a477be08"`
Dec 03 14:56:14.847 INFO running `"docker" "rm" "-f" "069a0422981e1cfd596c4263d8dfae48f575e3199b44c9a77e367c17a477be08"`
Dec 03 14:56:15.095 INFO [stdout] 069a0422981e1cfd596c4263d8dfae48f575e3199b44c9a77e367c17a477be08
Dec 03 14:56:15.103 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:15.547 INFO [stdout] 207bb875956dbac8b879d510db05b6934c4df8718267b68fd23e7b4b1d5f8639
Dec 03 14:56:15.550 INFO running `"docker" "start" "-a" "207bb875956dbac8b879d510db05b6934c4df8718267b68fd23e7b4b1d5f8639"`
Dec 03 14:56:16.995 INFO [stderr] usermod: no changes
Dec 03 14:56:17.271 INFO [stderr]    Compiling crisp-status-reporter v1.1.2 (/source)
Dec 03 14:56:38.100 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.07s
Dec 03 14:56:38.119 INFO [stderr] su: No module specific data is present
Dec 03 14:56:38.908 INFO running `"docker" "inspect" "207bb875956dbac8b879d510db05b6934c4df8718267b68fd23e7b4b1d5f8639"`
Dec 03 14:56:39.165 INFO running `"docker" "rm" "-f" "207bb875956dbac8b879d510db05b6934c4df8718267b68fd23e7b4b1d5f8639"`
Dec 03 14:56:39.511 INFO [stdout] 207bb875956dbac8b879d510db05b6934c4df8718267b68fd23e7b4b1d5f8639
Dec 03 14:56:39.517 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 14:56:39.950 INFO [stdout] 3affa5412dd135f43e0b1dbc8c85842c51da0f3f81c9bec1e3d6922e410166c3
Dec 03 14:56:39.953 INFO running `"docker" "start" "-a" "3affa5412dd135f43e0b1dbc8c85842c51da0f3f81c9bec1e3d6922e410166c3"`
Dec 03 14:56:41.039 INFO [stderr] usermod: no changes
Dec 03 14:56:41.383 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.31s
Dec 03 14:56:41.403 INFO [stderr]      Running /target/debug/deps/crisp_status_reporter-c5b9cfb2d0c37632
Dec 03 14:56:41.415 INFO [stdout] 
Dec 03 14:56:41.415 INFO [stdout] running 0 tests
Dec 03 14:56:41.415 INFO [stdout] 
Dec 03 14:56:41.415 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:56:41.415 INFO [stdout] 
Dec 03 14:56:41.415 INFO [stderr]      Running /target/debug/deps/report-f27e9e0626fe8a38
Dec 03 14:56:41.418 INFO [stdout] 
Dec 03 14:56:41.418 INFO [stdout] running 5 tests
Dec 03 14:56:41.418 INFO [stdout] test initialize_valid ... ok
Dec 03 14:56:41.418 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: crisp_status_reporter: Crisp Status Reporter: Will run using URL: https://report.crisp.watch/v1
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: tokio_threadpool::builder: build; num-workers=8
Dec 03 14:56:41.447 INFO [stderr] TRACE 2018-12-03T14:56:41Z: mio::poll: registering with poller
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: tokio_reactor::background: starting background reactor
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: tokio_core::reactor: consuming notification queue
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: tokio_core::reactor: loop poll - 43.112µs
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522177, tv_nsec: 321727392 }
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: tokio_core::reactor: loop process, 38.363µs
Dec 03 14:56:41.447 INFO [stderr] DEBUG 2018-12-03T14:56:41Z: crisp_status_reporter: Crisp Status Reporter: Now running
Dec 03 14:56:41.647 INFO [stdout] test initialize_invalid_replica_id ... ok
Dec 03 14:56:41.647 INFO [stdout] test initialize_invalid_node_id ... ok
Dec 03 14:56:41.647 INFO [stdout] test initialize_invalid_service_id ... ok
Dec 03 14:56:51.441 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: crisp_status_reporter: Crisp Status Reporter: Will dispatch request
Dec 03 14:56:51.441 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: crisp_status_reporter: Crisp Status Reporter: Will send request to URL: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/ with payload: ReportPayload { replica_id: "192.168.1.10", interval: 30, load: ReportPayloadLoad { cpu: 2.95, ram: 0.17279738 } }
Dec 03 14:56:51.442 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop poll - 10.001592596s
Dec 03 14:56:51.442 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522187, tv_nsec: 323378034 }
Dec 03 14:56:51.442 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop process, 73.941µs
Dec 03 14:56:51.442 INFO [stderr] TRACE 2018-12-03T14:56:51Z: hyper::client::pool: park; waiting for idle connection: "https://report.crisp.watch"
Dec 03 14:56:51.442 INFO [stderr] TRACE 2018-12-03T14:56:51Z: hyper::client::connect: Http::connect("https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/")
Dec 03 14:56:51.443 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop poll - 309.888µs
Dec 03 14:56:51.443 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522187, tv_nsec: 324090387 }
Dec 03 14:56:51.443 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop process, 58.821µs
Dec 03 14:56:51.443 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: hyper::client::dns: resolving host="report.crisp.watch", port=443
Dec 03 14:56:51.445 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop poll - 2.54986ms
Dec 03 14:56:51.445 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop time - Instant { tv_sec: 5522187, tv_nsec: 326731248 }
Dec 03 14:56:51.445 INFO [stderr] DEBUG 2018-12-03T14:56:51Z: tokio_core::reactor: loop process, 62.453µs
Dec 03 14:56:51.445 INFO [stderr] ERROR 2018-12-03T14:56:51Z: crisp_status_reporter: Crisp Status Reporter: Failed dispatching request: https://report.crisp.watch/v1/report/d657b4c1-dd07-4f94-ac7a-d4c3b4b219c1/5eca824b-4134-4126-982d-2c2338ecf3ab/: failed to lookup address information: Temporary failure in name resolution
Dec 03 14:56:51.445 INFO [stderr]  WARN 2018-12-03T14:56:51Z: crisp_status_reporter: Crisp Status Reporter: Last report failed, trying again sooner than usual
Dec 03 14:56:56.441 INFO [stdout] test run_and_end_valid ... ok
Dec 03 14:56:56.441 INFO [stdout] 
Dec 03 14:56:56.441 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:56:56.441 INFO [stdout] 
Dec 03 14:56:56.445 INFO [stderr]    Doc-tests crisp_status_reporter
Dec 03 14:57:11.518 INFO [stdout] 
Dec 03 14:57:11.518 INFO [stdout] running 0 tests
Dec 03 14:57:11.518 INFO [stdout] 
Dec 03 14:57:11.518 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 14:57:11.518 INFO [stdout] 
Dec 03 14:57:11.531 INFO [stderr] su: No module specific data is present
Dec 03 14:57:12.848 INFO running `"docker" "inspect" "3affa5412dd135f43e0b1dbc8c85842c51da0f3f81c9bec1e3d6922e410166c3"`
Dec 03 14:57:13.121 INFO running `"docker" "rm" "-f" "3affa5412dd135f43e0b1dbc8c85842c51da0f3f81c9bec1e3d6922e410166c3"`
Dec 03 14:57:13.438 INFO [stdout] 3affa5412dd135f43e0b1dbc8c85842c51da0f3f81c9bec1e3d6922e410166c3
